Dec 02 16:19:36.658 INFO testing snow-0.4.0 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 02 16:19:36.658 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:19:36.879 INFO [stdout] dca77cf77d352ccb8cbce8c861a04e4c903957dc71d1557e2d88f89a1a9deab1
Dec 02 16:19:36.881 INFO running `"docker" "start" "-a" "dca77cf77d352ccb8cbce8c861a04e4c903957dc71d1557e2d88f89a1a9deab1"`
Dec 02 16:19:37.338 INFO [stderr] usermod: no changes
Dec 02 16:19:37.506 INFO [stderr]    Compiling subtle v0.7.1
Dec 02 16:19:37.511 INFO [stderr]    Compiling static_slice v0.0.3
Dec 02 16:19:37.512 INFO [stderr]    Compiling chacha20-poly1305-aead v0.1.2
Dec 02 16:19:37.513 INFO [stderr]    Compiling snow v0.4.0 (/source)
Dec 02 16:19:38.387 INFO [stderr]    Compiling curve25519-dalek v0.19.1
Dec 02 16:19:44.330 INFO [stderr]    Compiling x25519-dalek v0.3.0
Dec 02 16:19:48.583 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.22s
Dec 02 16:19:48.593 INFO [stderr] su: No module specific data is present
Dec 02 16:19:49.008 INFO running `"docker" "inspect" "dca77cf77d352ccb8cbce8c861a04e4c903957dc71d1557e2d88f89a1a9deab1"`
Dec 02 16:19:49.080 INFO running `"docker" "rm" "-f" "dca77cf77d352ccb8cbce8c861a04e4c903957dc71d1557e2d88f89a1a9deab1"`
Dec 02 16:19:49.164 INFO [stdout] dca77cf77d352ccb8cbce8c861a04e4c903957dc71d1557e2d88f89a1a9deab1
Dec 02 16:19:49.166 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:19:49.371 INFO [stdout] 8bbaec41292c71b7b468c90852f238fc7ad6f9c8ba8aed1b27d6f530761330ba
Dec 02 16:19:49.376 INFO running `"docker" "start" "-a" "8bbaec41292c71b7b468c90852f238fc7ad6f9c8ba8aed1b27d6f530761330ba"`
Dec 02 16:19:49.741 INFO [stderr] usermod: no changes
Dec 02 16:19:49.923 INFO [stderr]    Compiling term v0.5.1
Dec 02 16:19:49.926 INFO [stderr]    Compiling criterion-plot v0.2.5
Dec 02 16:19:52.951 INFO [stderr]    Compiling simplelog v0.5.3
Dec 02 16:19:54.449 INFO [stderr]    Compiling criterion v0.2.5
Dec 02 16:20:07.101 INFO [stderr]    Compiling snow v0.4.0 (/source)
Dec 02 16:20:14.571 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 24.81s
Dec 02 16:20:14.582 INFO [stderr] su: No module specific data is present
Dec 02 16:20:15.233 INFO running `"docker" "inspect" "8bbaec41292c71b7b468c90852f238fc7ad6f9c8ba8aed1b27d6f530761330ba"`
Dec 02 16:20:15.419 INFO running `"docker" "rm" "-f" "8bbaec41292c71b7b468c90852f238fc7ad6f9c8ba8aed1b27d6f530761330ba"`
Dec 02 16:20:15.613 INFO [stdout] 8bbaec41292c71b7b468c90852f238fc7ad6f9c8ba8aed1b27d6f530761330ba
Dec 02 16:20:15.617 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:20:15.957 INFO [stdout] 073d2d49b9bfdb54280401be1e4c1eeb46d5ce460ac082240bc72ae6092fc07d
Dec 02 16:20:15.961 INFO running `"docker" "start" "-a" "073d2d49b9bfdb54280401be1e4c1eeb46d5ce460ac082240bc72ae6092fc07d"`
Dec 02 16:20:16.668 INFO [stderr] usermod: no changes
Dec 02 16:20:17.051 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.34s
Dec 02 16:20:17.071 INFO [stderr]      Running /target/debug/deps/snow-c3fcd50a587e1bdc
Dec 02 16:20:17.074 INFO [stdout] 
Dec 02 16:20:17.074 INFO [stdout] running 22 tests
Dec 02 16:20:17.075 INFO [stdout] test params::tests::test_modified_psk_handshake ... ok
Dec 02 16:20:17.075 INFO [stdout] test params::tests::test_modified_multi_psk_handshake ... ok
Dec 02 16:20:17.075 INFO [stdout] test params::tests::test_fallback_mod ... ok
Dec 02 16:20:17.075 INFO [stdout] test params::tests::test_basic ... ok
Dec 02 16:20:17.075 INFO [stdout] test builder::tests::test_builder_missing_prereqs ... ok
Dec 02 16:20:17.075 INFO [stdout] test params::tests::test_multi_psk_mod ... ok
Dec 02 16:20:17.075 INFO [stdout] test params::tests::test_simple_handshake ... ok
Dec 02 16:20:17.075 INFO [stdout] test builder::tests::test_builder_bad_spec ... ok
Dec 02 16:20:17.076 INFO [stdout] test params::tests::test_single_psk_mod ... ok
Dec 02 16:20:17.077 INFO [stdout] test builder::tests::test_builder_keygen ... ok
Dec 02 16:20:17.077 INFO [stdout] test resolvers::default::tests::test_chachapoly_empty ... ok
Dec 02 16:20:17.078 INFO [stdout] test resolvers::default::tests::test_aes256_gcm ... ok
Dec 02 16:20:17.078 INFO [stdout] test resolvers::default::tests::test_chachapoly_known_answer ... ok
Dec 02 16:20:17.078 INFO [stdout] test builder::tests::test_builder ... ok
Dec 02 16:20:17.083 INFO [stdout] test params::tests::test_psk_fallback_mod ... ok
Dec 02 16:20:17.083 INFO [stdout] test resolvers::default::tests::test_blake2b ... ok
Dec 02 16:20:17.083 INFO [stdout] test resolvers::default::tests::test_chachapoly_nonempty ... ok
Dec 02 16:20:17.095 INFO [stdout] test resolvers::default::tests::test_curve25519 ... ok
Dec 02 16:20:17.095 INFO [stdout] test resolvers::default::tests::test_hmac_sha256_sha512 ... ok
Dec 02 16:20:17.095 INFO [stdout] test resolvers::default::tests::test_blake2s ... ok
Dec 02 16:20:17.096 INFO [stdout] test resolvers::default::tests::test_sha256 ... ok
Dec 02 16:20:17.096 INFO [stdout] test resolvers::default::tests::test_poly1305 ... ok
Dec 02 16:20:17.096 INFO [stdout] 
Dec 02 16:20:17.096 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:20:17.096 INFO [stdout] 
Dec 02 16:20:17.103 INFO [stderr]      Running /target/debug/deps/general-abd23fc97a90c40c
Dec 02 16:20:17.109 INFO [stdout] 
Dec 02 16:20:17.109 INFO [stdout] running 21 tests
Dec 02 16:20:17.124 INFO [stdout] test test_buffer_issues ... ok
Dec 02 16:20:17.195 INFO [stdout] test test_Npsk0_expected_value ... ok
Dec 02 16:20:17.195 INFO [stdout] test test_Xpsk0_expected_value ... ok
Dec 02 16:20:17.215 INFO [stdout] test test_NNpsk0_sanity_session ... ok
Dec 02 16:20:17.215 INFO [stdout] test test_checkpointing ... ok
Dec 02 16:20:17.216 INFO [stdout] test test_handshake_message_undersized_output_buffer ... ok
Dec 02 16:20:17.250 INFO [stdout] test test_oneway_initiator_enforcements ... ok
Dec 02 16:20:17.250 INFO [stdout] test test_buffer_issues_encrypted_handshake ... ok
Dec 02 16:20:17.267 INFO [stdout] test test_oneway_responder_enforcements ... ok
Dec 02 16:20:17.267 INFO [stdout] test test_noise_session_transition_change ... ok
Dec 02 16:20:17.268 INFO [stdout] test test_rekey ... ok
Dec 02 16:20:17.268 INFO [stdout] test test_protocol_name ... ok
Dec 02 16:20:17.283 INFO [stdout] test test_handshake_message_exceeds_max_len ... ok
Dec 02 16:20:17.283 INFO [stdout] test test_transport_message_exceeds_max_len ... ok
Dec 02 16:20:17.284 INFO [stdout] test test_send_trait ... ok
Dec 02 16:20:17.298 INFO [stdout] test test_XXpsk0_expected_value ... ok
Dec 02 16:20:17.298 INFO [stdout] test test_transport_message_undersized_output_buffer ... ok
Dec 02 16:20:17.298 INFO [stdout] test test_XXpsk3_sanity_session ... ok
Dec 02 16:20:17.298 INFO [stdout] test test_set_psk ... ok
Dec 02 16:20:17.299 INFO [stdout] test test_sanity_session ... ok
Dec 02 16:20:17.307 INFO [stdout] test test_get_remote_static ... ok
Dec 02 16:20:17.307 INFO [stdout] 
Dec 02 16:20:17.307 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:20:17.307 INFO [stdout] 
Dec 02 16:20:17.308 INFO [stderr]      Running /target/debug/deps/vectors-8aa2899ceae914af
Dec 02 16:20:17.311 INFO [stdout] 
Dec 02 16:20:17.311 INFO [stdout] running 0 tests
Dec 02 16:20:17.311 INFO [stdout] 
Dec 02 16:20:17.311 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:20:17.311 INFO [stdout] 
Dec 02 16:20:17.311 INFO [stderr]    Doc-tests snow
Dec 02 16:20:20.797 INFO [stdout] 
Dec 02 16:20:20.797 INFO [stdout] running 7 tests
Dec 02 16:20:20.797 INFO [stdout] test src/session.rs - session::Session::into_stateless_transport_mode (line 299) ... ignored
Dec 02 16:20:20.797 INFO [stdout] test src/session.rs - session::Session::into_transport_mode (line 264) ... ignored
Dec 02 16:20:20.797 INFO [stdout] test src/session.rs - session::Session::is_handshake_finished (line 55) ... ignored
Dec 02 16:20:20.797 INFO [stdout] test src/session.rs - session::Session::was_write_payload_encrypted (line 34) ... ignored
Dec 02 16:20:29.119 INFO [stdout] test src/builder.rs - builder::Builder (line 26) ... ok
Dec 02 16:20:29.242 INFO [stdout] test src/lib.rs -  (line 12) ... ok
Dec 02 16:20:29.712 INFO [stdout] test src/params/mod.rs - params::NoiseParams (line 118) ... ok
Dec 02 16:20:29.712 INFO [stdout] 
Dec 02 16:20:29.712 INFO [stdout] test result: ok. 3 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out
Dec 02 16:20:29.712 INFO [stdout] 
Dec 02 16:20:29.727 INFO [stderr] su: No module specific data is present
Dec 02 16:20:30.034 INFO running `"docker" "inspect" "073d2d49b9bfdb54280401be1e4c1eeb46d5ce460ac082240bc72ae6092fc07d"`
Dec 02 16:20:30.100 INFO running `"docker" "rm" "-f" "073d2d49b9bfdb54280401be1e4c1eeb46d5ce460ac082240bc72ae6092fc07d"`
Dec 02 16:20:30.196 INFO [stdout] 073d2d49b9bfdb54280401be1e4c1eeb46d5ce460ac082240bc72ae6092fc07d
